[INFO] cloning repository https://github.com/beettlle/peal
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/beettlle/peal" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbeettlle%2Fpeal", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbeettlle%2Fpeal'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7cfe39dc7a237060fe92526df224a27a86d2bc51
[INFO] testing beettlle/peal against try#b8e88e5ddf5521a9f43ee3f62a702388c713e4bb for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbeettlle%2Fpeal" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-2-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/beettlle/peal
[INFO] finished tweaking git repo https://github.com/beettlle/peal
[INFO] tweaked toml for git repo https://github.com/beettlle/peal written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/beettlle/peal on toolchain b8e88e5ddf5521a9f43ee3f62a702388c713e4bb
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/beettlle/peal already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b6be9c38af1a94d1bd40401cdc403021ce7e549776368d1ecd709e1e650efc74
[INFO] running `Command { std: "docker" "start" "-a" "b6be9c38af1a94d1bd40401cdc403021ce7e549776368d1ecd709e1e650efc74", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b6be9c38af1a94d1bd40401cdc403021ce7e549776368d1ecd709e1e650efc74", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b6be9c38af1a94d1bd40401cdc403021ce7e549776368d1ecd709e1e650efc74", kill_on_drop: false }`
[INFO] [stdout] b6be9c38af1a94d1bd40401cdc403021ce7e549776368d1ecd709e1e650efc74
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2e110fd60f6e7d3819a5fb11d080e2d231ca6236d8fd3ed1d06c6c9312f3c201
[INFO] running `Command { std: "docker" "start" "-a" "2e110fd60f6e7d3819a5fb11d080e2d231ca6236d8fd3ed1d06c6c9312f3c201", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling iana-time-zone v0.1.65
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling peal v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 34.55s
[INFO] running `Command { std: "docker" "inspect" "2e110fd60f6e7d3819a5fb11d080e2d231ca6236d8fd3ed1d06c6c9312f3c201", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2e110fd60f6e7d3819a5fb11d080e2d231ca6236d8fd3ed1d06c6c9312f3c201", kill_on_drop: false }`
[INFO] [stdout] 2e110fd60f6e7d3819a5fb11d080e2d231ca6236d8fd3ed1d06c6c9312f3c201
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6b64583b7dbd9ffba77bbcb9262b2e06db1fccba6a0cd373dca41755088e78ea
[INFO] running `Command { std: "docker" "start" "-a" "6b64583b7dbd9ffba77bbcb9262b2e06db1fccba6a0cd373dca41755088e78ea", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling peal v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.62s
[INFO] running `Command { std: "docker" "inspect" "6b64583b7dbd9ffba77bbcb9262b2e06db1fccba6a0cd373dca41755088e78ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6b64583b7dbd9ffba77bbcb9262b2e06db1fccba6a0cd373dca41755088e78ea", kill_on_drop: false }`
[INFO] [stdout] 6b64583b7dbd9ffba77bbcb9262b2e06db1fccba6a0cd373dca41755088e78ea
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0b0018bb60059c4a5152a4185b71137b755d748f900850082719dd6487ef8137
[INFO] running `Command { std: "docker" "start" "-a" "0b0018bb60059c4a5152a4185b71137b755d748f900850082719dd6487ef8137", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/peal-3bb1fdf0f23ae855)
[INFO] [stdout] 
[INFO] [stdout] running 356 tests
[INFO] [stdout] test cli::tests::prompt_subcommand_parses_with_output ... ok
[INFO] [stdout] test cli::tests::phase_3_retry_count_flag_parses ... ok
[INFO] [stdout] test cli::tests::from_task_flag_parses ... ok
[INFO] [stdout] test cli::tests::prompt_subcommand_parses_without_output ... ok
[INFO] [stdout] test cli::tests::run_subcommand_accepts_no_plan_no_repo ... ok
[INFO] [stdout] test cli::tests::max_consecutive_task_failures_flag_parses ... ok
[INFO] [stdout] test cli::tests::unknown_subcommand_rejected ... ok
[INFO] [stdout] test cli::tests::normalize_retries_flag_parses ... ok
[INFO] [stdout] test cli::tests::task_flag_parses ... ok
[INFO] [stdout] test cli::tests::task_and_from_task_conflict ... ok
[INFO] [stdout] test config::tests::cli_overrides_env ... ok
[INFO] [stdout] test cli::tests::run_subcommand_parses_required_args ... ok
[INFO] [stdout] test cli::tests::no_subcommand_shows_error ... ok
[INFO] [stdout] test config::tests::commit_after_phase2_from_env ... ok
[INFO] [stdout] test cli::tests::run_subcommand_parses_all_optional_flags ... ok
[INFO] [stdout] test config::tests::defaults_applied_when_only_required_fields_present ... ok
[INFO] [stdout] test config::tests::commit_after_phase2_from_toml ... ok
[INFO] [stdout] test config::tests::cli_overrides_file ... ok
[INFO] [stdout] test config::tests::documented_example_toml_parses_successfully ... ok
[INFO] [stdout] test config::tests::commit_after_phase2_defaults_to_false ... ok
[INFO] [stdout] test config::tests::commit_after_phase2_from_cli ... ok
[INFO] [stdout] test cli::tests::validate_plan_text_and_min_plan_text_len_flags_parse ... ok
[INFO] [stdout] test config::tests::env_stet_commands_parsed_from_comma_separated ... ok
[INFO] [stdout] test config::tests::invalid_env_var_returns_error ... ok
[INFO] [stdout] test config::tests::max_consecutive_task_failures_from_cli ... ok
[INFO] [stdout] test config::tests::missing_config_file_returns_error ... ok
[INFO] [stdout] test config::tests::missing_repo_path_errors ... ok
[INFO] [stdout] test config::tests::missing_plan_path_errors ... ok
[INFO] [stdout] test config::tests::max_consecutive_task_failures_from_env ... ok
[INFO] [stdout] test config::tests::normalize_plan_from_env ... ok
[INFO] [stdout] test config::tests::normalize_plan_defaults_to_false ... ok
[INFO] [stdout] test config::tests::normalize_retry_count_defaults_to_zero ... ok
[INFO] [stdout] test config::tests::env_overrides_file ... ok
[INFO] [stdout] test config::tests::normalize_retry_count_from_env ... ok
[INFO] [stdout] test config::tests::on_findings_remaining_cli_overrides_env ... ok
[INFO] [stdout] test config::tests::on_findings_remaining_from_cli ... ok
[INFO] [stdout] test config::tests::on_findings_remaining_defaults_to_fail ... ok
[INFO] [stdout] test config::tests::on_findings_remaining_from_env ... ok
[INFO] [stdout] test config::tests::normalize_retry_count_from_toml ... ok
[INFO] [stdout] test config::tests::loads_from_toml_file ... ok
[INFO] [stdout] test config::tests::on_findings_remaining_from_toml ... ok
[INFO] [stdout] test config::tests::invalid_toml_returns_error ... ok
[INFO] [stdout] test config::tests::full_precedence_chain ... ok
[INFO] [stdout] test config::tests::max_consecutive_task_failures_from_toml ... ok
[INFO] [stdout] test config::tests::max_consecutive_task_failures_precedence_cli_over_env_over_file ... ok
[INFO] [stdout] test config::tests::normalize_plan_cli_overrides_env_and_file ... ok
[INFO] [stdout] test config::tests::normalize_prompt_path_from_env ... ok
[INFO] [stdout] test config::tests::normalize_plan_from_toml ... ok
[INFO] [stdout] test config::tests::phase_3_retry_count_from_env ... ok
[INFO] [stdout] test config::tests::normalize_prompt_path_from_toml ... ok
[INFO] [stdout] test config::tests::phase_3_retry_count_defaults_to_zero ... ok
[INFO] [stdout] test config::tests::parallel_flag_from_cli ... ok
[INFO] [stdout] test config::tests::parse_extra_args_str_splits_space_and_comma ... ok
[INFO] [stdout] test config::tests::phase_3_retry_count_from_cli ... ok
[INFO] [stdout] test config::tests::phase_3_retry_count_from_toml ... ok
[INFO] [stdout] test config::tests::partial_toml_fills_defaults ... ok
[INFO] [stdout] test config::tests::run_summary_path_from_env ... ok
[INFO] [stdout] test config::tests::stet_extra_args_from_env ... ok
[INFO] [stdout] test config::tests::validate_fails_when_plan_path_does_not_exist ... ok
[INFO] [stdout] test config::tests::run_summary_path_from_toml ... ok
[INFO] [stdout] test config::tests::unknown_toml_key_returns_error ... ok
[INFO] [stdout] test config::tests::stet_extra_args_from_toml ... ok
[INFO] [stdout] test config::tests::validate_fails_when_plan_path_is_directory ... ok
[INFO] [stdout] test config::tests::post_run_commands_default_empty_and_no_timeout ... ok
[INFO] [stdout] test config::tests::post_run_commands_from_env ... ok
[INFO] [stdout] test config::tests::post_run_commands_from_toml ... ok
[INFO] [stdout] test config::tests::run_summary_path_from_cli ... ok
[INFO] [stdout] test config::tests::normalize_plan_from_cli ... ok
[INFO] [stdout] test config::tests::validate_fails_when_repo_path_does_not_exist ... ok
[INFO] [stdout] test config::tests::validate_plan_text_defaults_off ... ok
[INFO] [stdout] test config::tests::validate_plan_text_from_cli ... ok
[INFO] [stdout] test config::tests::validate_plan_text_from_env ... ok
[INFO] [stdout] test cursor::tests::fails_for_explicit_path_that_does_not_exist ... ok
[INFO] [stdout] test cursor::tests::resolves_explicit_absolute_path ... ok
[INFO] [stdout] test logging::tests::build_filter_accepts_directive_syntax ... ok
[INFO] [stdout] test cursor::tests::fails_when_path_var_is_empty ... ok
[INFO] [stdout] test cursor::tests::fails_when_path_var_is_none ... ok
[INFO] [stdout] test cursor::tests::resolves_echo_on_real_path ... ok
[INFO] [stdout] test config::tests::validate_fails_when_repo_path_is_file ... ok
[INFO] [stdout] test logging::tests::build_filter_uses_default_when_no_override ... ok
[INFO] [stdout] test logging::tests::build_filter_uses_explicit_level ... ok
[INFO] [stdout] test cursor::tests::fails_for_nonexistent_command ... ok
[INFO] [stdout] test phase::tests::args_for_log_single_element ... ok
[INFO] [stdout] test phase::tests::args_for_log_empty ... ok
[INFO] [stdout] test phase::tests::argv_without_model ... ok
[INFO] [stdout] test phase::tests::args_for_log_multi_last_redacted ... ok
[INFO] [stdout] test phase::tests::argv_prompt_is_last_arg ... ok
[INFO] [stdout] test phase::tests::check_result_nonzero_exit ... ok
[INFO] [stdout] test phase::tests::phase2_argv_includes_sandbox ... ok
[INFO] [stdout] test phase::tests::check_result_timeout ... ok
[INFO] [stdout] test logging::tests::open_log_file_appends ... ok
[INFO] [stdout] test phase::tests::phase2_argv_with_model ... ok
[INFO] [stdout] test phase::tests::phase2_argv_does_not_contain_plan_flag ... ok
[INFO] [stdout] test cursor::tests::skips_file_without_execute_permission ... ok
[INFO] [stdout] test cursor::tests::finds_binary_in_custom_path ... ok
[INFO] [stdout] test cursor::tests::skips_directory_with_same_name ... ok
[INFO] [stdout] test config::tests::validate_plan_text_and_min_from_toml ... ok
[INFO] [stdout] test phase::tests::check_result_success ... ok
[INFO] [stdout] test phase::tests::argv_with_model ... ok
[INFO] [stdout] test phase::tests::phase2_argv_without_model ... ok
[INFO] [stdout] test config::tests::validate_fails_when_repo_not_git ... ok
[INFO] [stdout] test phase::tests::phase3_argv_does_not_contain_plan_flag ... ok
[INFO] [stdout] test phase::tests::phase3_argv_with_model ... ok
[INFO] [stdout] test logging::tests::open_log_file_creates_parent_dirs ... ok
[INFO] [stdout] test phase::tests::phase2_argv_prompt_is_last_arg ... ok
[INFO] [stdout] test phase::tests::phase3_argv_prompt_is_last_arg ... ok
[INFO] [stdout] test phase::tests::phase3_argv_without_model ... ok
[INFO] [stdout] test phase::tests::run_phase2_spawn_failure ... ok
[INFO] [stdout] test phase::tests::run_phase3_spawn_failure ... ok
[INFO] [stdout] test phase::tests::run_phase1_fails_on_timeout ... ok
[INFO] [stdout] test phase::tests::run_phase1_with_echo_stub ... ok
[INFO] [stdout] test phase::tests::run_phase3_without_suggestions_echo_stub ... ok
[INFO] [stdout] test plan::tests::build_normalize_prompt_custom_file_missing_returns_err ... ok
[INFO] [stdout] test plan::tests::build_normalize_prompt_custom_file_replaces_placeholder ... ok
[INFO] [stdout] test plan::tests::build_normalize_prompt_none_uses_builtin ... ok
[INFO] [stdout] test phase::tests::run_phase2_fails_on_nonzero_exit ... ok
[INFO] [stdout] test phase::tests::run_phase1_spawn_failure ... ok
[INFO] [stdout] test plan::tests::crlf_normalised_to_lf ... ok
[INFO] [stdout] test plan::tests::body_captured_until_eof ... ok
[INFO] [stdout] test plan::tests::consecutive_parallel_tasks_form_block ... ok
[INFO] [stdout] test plan::tests::body_captured_until_next_heading ... ok
[INFO] [stdout] test phase::tests::run_phase3_fails_on_nonzero_exit ... ok
[INFO] [stdout] test plan::tests::all_sequential_produces_sequential_segments ... ok
[INFO] [stdout] test plan::tests::empty_content_produces_no_tasks ... ok
[INFO] [stdout] test plan::tests::filter_single_task_not_found ... ok
[INFO] [stdout] test plan::tests::filter_from_task_returns_tail ... ok
[INFO] [stdout] test plan::tests::filter_from_task_recomputes_parallel_segments ... ok
[INFO] [stdout] test plan::tests::filter_single_task_returns_matching_task ... ok
[INFO] [stdout] test plan::tests::filter_from_task_not_found ... ok
[INFO] [stdout] test plan::tests::filter_from_last_task_returns_single ... ok
[INFO] [stdout] test plan::tests::is_canonical_empty_content_false ... ok
[INFO] [stdout] test plan::tests::is_canonical_crlf_and_task1_true ... ok
[INFO] [stdout] test plan::tests::is_canonical_leading_space_false ... ok
[INFO] [stdout] test plan::tests::is_canonical_only_preamble_false ... ok
[INFO] [stdout] test plan::tests::gaps_in_indices_preserved_in_order ... ok
[INFO] [stdout] test plan::tests::is_canonical_not_a_task_false ... ok
[INFO] [stdout] test plan::tests::is_canonical_task1_body_true ... ok
[INFO] [stdout] test plan::tests::is_canonical_multiple_task_lines_true ... ok
[INFO] [stdout] test plan::tests::heading_with_extra_whitespace ... ok
[INFO] [stdout] test plan::tests::detected_content_still_parses_correctly ... ok
[INFO] [stdout] test plan::tests::is_canonical_task999_with_trailing_newline_true ... ok
[INFO] [stdout] test plan::tests::is_canonical_task42_parallel_true ... ok
[INFO] [stdout] test plan::tests::is_canonical_task999_no_newline_true ... ok
[INFO] [stdout] test plan::tests::multiple_sequential_tasks ... ok
[INFO] [stdout] test plan::tests::multiple_parallel_blocks ... ok
[INFO] [stdout] test plan::tests::non_task_headings_not_matched ... ok
[INFO] [stdout] test plan::tests::normalization_argv_with_model_includes_model ... ok
[INFO] [stdout] test plan::tests::normalization_argv_without_model_omits_model ... ok
[INFO] [stdout] test plan::tests::parallel_suffix_detected ... ok
[INFO] [stdout] test config::tests::validate_rejects_invalid_on_findings_remaining ... ok
[INFO] [stdout] test config::tests::validate_succeeds_with_valid_file_and_directory ... ok
[INFO] [stdout] test plan::tests::parse_plan_or_fail_non_canonical_returns_err_with_snippet ... ok
[INFO] [stdout] test plan::tests::parse_plan_file_not_found ... ok
[INFO] [stdout] test plan::tests::parse_plan_or_fail_canonical_returns_ok ... ok
[INFO] [stdout] test plan::tests::parse_plan_or_fail_empty_returns_err_with_snippet ... ok
[INFO] [stdout] test plan::tests::single_parallel_task_treated_as_sequential ... ok
[INFO] [stdout] test plan::tests::task_by_index_boundary_first_and_last ... ok
[INFO] [stdout] test plan::tests::parse_docs_improvement_plan ... ok
[INFO] [stdout] test plan::tests::parse_plan_file_invalid_utf8 ... ok
[INFO] [stdout] test plan::tests::preamble_before_first_heading_ignored ... ok
[INFO] [stdout] test plan::tests::task_by_index_returns_none_for_missing ... ok
[INFO] [stdout] test plan::tests::task_by_index_finds_existing ... ok
[INFO] [stdout] test plan::tests::single_task_parsed ... ok
[INFO] [stdout] test plan::tests::task_by_index_zero_returns_none ... ok
[INFO] [stdout] test plan_prompt::tests::prompt_contains_task_heading_example ... ok
[INFO] [stdout] test plan::tests::parse_plan_file_valid ... ok
[INFO] [stdout] test plan_prompt::tests::prompt_contains_parallel_marker ... ok
[INFO] [stdout] test prompt::tests::build_normalize_prompt_from_template_replaces_placeholder ... ok
[INFO] [stdout] test plan::tests::parse_plan_or_fail_snippet_bounded_long_output ... ok
[INFO] [stdout] test prompt::tests::delimiters_are_distinct_across_phases ... ok
[INFO] [stdout] test prompt::tests::phase1_handles_empty_content ... ok
[INFO] [stdout] test prompt::tests::phase1_handles_multiline_content ... ok
[INFO] [stdout] test prompt::tests::normalize_plan_prompt_contains_instructions_and_delimiter_and_doc ... ok
[INFO] [stdout] test prompt::tests::phase1_has_exactly_two_task_delimiters ... ok
[INFO] [stdout] test prompt::tests::normalize_plan_prompt_fences_empty_doc ... ok
[INFO] [stdout] test prompt::tests::phase2_handles_empty_plan ... ok
[INFO] [stdout] test plan::tests::parse_docs_phase4_phase5_phase6_plan_files ... ok
[INFO] [stdout] test prompt::tests::phase2_handles_multiline_plan ... ok
[INFO] [stdout] test prompt::tests::phase3_handles_multiline_output ... ok
[INFO] [stdout] test prompt::tests::phase3_has_exactly_two_stet_delimiters ... ok
[INFO] [stdout] test prompt::tests::phase3_with_suggestions_includes_block ... ok
[INFO] [stdout] test prompt::tests::phase2_wraps_plan_in_delimiters ... ok
[INFO] [stdout] test prompt::tests::phase3_handles_empty_output ... ok
[INFO] [stdout] test prompt::tests::phase3_with_suggestions_delimiters_distinct ... ok
[INFO] [stdout] test prompt::tests::phase3_with_suggestions_preserves_stet_block ... ok
[INFO] [stdout] test prompt::tests::phase1_wraps_task_in_delimiters ... ok
[INFO] [stdout] test prompt::tests::phase3_with_suggestions_none_matches_phase3 ... ok
[INFO] [stdout] test prompt::tests::phase3_wraps_stet_in_delimiters ... ok
[INFO] [stdout] test runner::tests::empty_plan_returns_empty_results ... ok
[INFO] [stdout] test plan_prompt::tests::prompt_contains_peal_reference ... ok
[INFO] [stdout] test phase::tests::run_phase2_with_echo_stub ... ok
[INFO] [stdout] test phase::tests::run_phase1_fails_on_nonzero_exit ... ok
[INFO] [stdout] test phase::tests::run_phase3_with_suggestions_echo_stub ... ok
[INFO] [stdout] test phase::tests::run_phase3_with_echo_stub ... ok
[INFO] [stdout] test phase::tests::run_phase3_triage_retries_then_ok_empty_stdout ... ok
[INFO] [stdout] test config::tests::validate_rejects_invalid_on_stet_fail ... ok
[INFO] [stdout] test runner::tests::fails_fast_on_first_error ... ok
[INFO] [stdout] test runner::tests::run_all_all_completed_produces_empty_results ... ok
[INFO] [stdout] test phase::tests::run_phase3_retries_then_fails_with_phase_3_retry_count ... ok
[INFO] [stdout] test runner::tests::run_all_empty_plan ... ok
[INFO] [stdout] test runner::tests::commit_after_phase2_creates_commit_with_message ... ok
[INFO] [stdout] test runner::tests::commit_after_phase2_nothing_to_commit_returns_ok ... ok
[INFO] [stdout] test runner::tests::continue_with_remaining_tasks_true_returns_ok ... ok
[INFO] [stdout] test runner::tests::parallel_block_phase3_in_segment_order ... ok
[INFO] [stdout] test runner::tests::parallel_block_runs_concurrently ... ok
[INFO] [stdout] test runner::tests::phase1_plan_text_validation_fails_after_retry ... ok
[INFO] [stdout] test runner::tests::preserves_task_order ... ok
[INFO] [stdout] test runner::tests::run_all_fails_fast_on_phase1_error ... ok
[INFO] [stdout] test runner::tests::run_all_phase3_failure_saves_state ... ok
[INFO] [stdout] test runner::tests::consecutive_task_failures_cap_stops_run_and_persists_state ... ok
[INFO] [stdout] test runner::tests::run_all_creates_state_dir_if_missing ... ok
[INFO] [stdout] test runner::tests::run_all_skips_phase3_when_stet_none ... ok
[INFO] [stdout] test runner::tests::run_all_phase2_receives_phase1_plan_text ... ok
[INFO] [stdout] test runner::tests::run_phase1_all_skips_previously_completed_tasks ... ok
[INFO] [stdout] test runner::tests::run_all_executes_both_phases_for_all_tasks ... ok
[INFO] [stdout] test runner::tests::scheduled_all_completed_returns_empty ... ok
[INFO] [stdout] test runner::tests::parallel_block_persists_state_for_all ... ok
[INFO] [stdout] test runner::tests::parallel_block_sequential_fallback ... ok
[INFO] [stdout] test runner::tests::run_all_on_failure_persists_only_completed_tasks ... ok
[INFO] [stdout] test runner::tests::run_all_runs_phase3_no_findings ... ok
[INFO] [stdout] test runner::tests::run_all_partial_completion_resumes_correctly ... ok
[INFO] [stdout] test runner::tests::run_all_single_task ... ok
[INFO] [stdout] test runner::tests::single_task_plan ... ok
[INFO] [stdout] test runner::tests::validate_plan_text_disabled_always_ok ... ok
[INFO] [stdout] test runner::tests::validate_plan_text_enabled_below_min_err ... ok
[INFO] [stdout] test runner::tests::validate_plan_text_enabled_empty_err ... ok
[INFO] [stdout] test runner::tests::validate_plan_text_enabled_meets_min_ok ... ok
[INFO] [stdout] test runner::tests::validate_plan_text_enabled_non_empty_ok_when_no_min ... ok
[INFO] [stdout] test state::tests::is_task_completed_reflects_marks ... ok
[INFO] [stdout] test state::tests::json_format_matches_expected_structure ... ok
[INFO] [stdout] test state::tests::json_includes_optional_fields_when_present ... ok
[INFO] [stdout] test state::tests::load_state_returns_none_on_invalid_json ... ok
[INFO] [stdout] test state::tests::load_state_returns_none_when_file_missing ... ok
[INFO] [stdout] test state::tests::load_state_roundtrips_saved_state ... ok
[INFO] [stdout] test state::tests::mark_task_completed_inserts_sorted_and_deduplicates ... ok
[INFO] [stdout] test runner::tests::scheduled_fail_fast_in_parallel_block_saves_prior ... ok
[INFO] [stdout] test state::tests::matches_context_returns_false_on_repo_path_mismatch ... ok
[INFO] [stdout] test state::tests::matches_context_returns_true_for_matching_paths ... ok
[INFO] [stdout] test state::tests::new_state_has_empty_defaults ... ok
[INFO] [stdout] test state::tests::save_state_creates_directory_if_missing ... ok
[INFO] [stdout] test runner::tests::parallel_block_failure_persists_completed ... ok
[INFO] [stdout] test state::tests::save_state_produces_valid_deserializable_json ... ok
[INFO] [stdout] test runner::tests::parallel_block_single_task_sequential ... ok
[INFO] [stdout] test state::tests::serialization_roundtrip ... ok
[INFO] [stdout] test runner::tests::run_all_runs_phase3_with_findings_resolved ... ok
[INFO] [stdout] test runner::tests::scheduled_resume_parallel_block_concurrent_path ... ok
[INFO] [stdout] test stet::tests::address_findings_delegates_correctly ... ok
[INFO] [stdout] test stet::tests::address_findings_no_suggestions_when_plain_text ... ok
[INFO] [stdout] test runner::tests::runs_phase1_for_all_tasks ... ok
[INFO] [stdout] test stet::tests::address_loop_exits_immediately_when_no_findings ... ok
[INFO] [stdout] test stet::tests::count_findings_empty_array_returns_zero ... ok
[INFO] [stdout] test stet::tests::count_findings_issues_array ... ok
[INFO] [stdout] test stet::tests::count_findings_json_object_with_findings_array ... ok
[INFO] [stdout] test stet::tests::count_findings_json_top_level_array ... ok
[INFO] [stdout] test stet::tests::count_findings_non_json_returns_one ... ok
[INFO] [stdout] test stet::tests::count_findings_stet_shaped ... ok
[INFO] [stdout] test stet::tests::detect_findings_exit_code_none_nonjson ... ok
[INFO] [stdout] test stet::tests::detect_findings_human_multiline_last_line_zero_findings ... ok
[INFO] [stdout] test stet::tests::detect_findings_human_one_finding ... ok
[INFO] [stdout] test stet::tests::detect_findings_human_zero_findings_no_trailing_dot ... ok
[INFO] [stdout] test stet::tests::detect_findings_human_zero_findings_with_newline ... ok
[INFO] [stdout] test stet::tests::detect_findings_human_zero_findings_with_tokens_per_sec ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_findings_takes_priority_over_count ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_issues_empty_no_findings ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_issues_nonempty ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_no_findings_no_count ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_with_count_nonzero ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_with_count_zero ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_with_empty_findings_array ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_with_extra_fields ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_object_with_nonempty_findings_array ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_scalar_value ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_top_level_array_with_items ... ok
[INFO] [stdout] test stet::tests::detect_findings_json_top_level_empty_array ... ok
[INFO] [stdout] test stet::tests::detect_findings_nonjson_exit_zero_empty_stdout ... ok
[INFO] [stdout] test stet::tests::detect_findings_nonjson_exit_zero_nonempty_stdout ... ok
[INFO] [stdout] test stet::tests::detect_findings_nonjson_exit_zero_whitespace_only_stdout ... ok
[INFO] [stdout] test stet::tests::detect_findings_nonjson_nonzero_exit ... ok
[INFO] [stdout] test stet::tests::detect_findings_stet_no_findings_exact ... ok
[INFO] [stdout] test stet::tests::detect_findings_stet_with_findings_exact ... ok
[INFO] [stdout] test state::tests::matches_context_returns_false_on_plan_path_mismatch ... ok
[INFO] [stdout] test state::tests::save_state_overwrites_existing_file ... ok
[INFO] [stdout] test runner::tests::run_all_preserves_task_order ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_from_issues_array ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_from_json_findings ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_from_top_level_array ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_mixed_findings ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_none_for_empty_findings_array ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_none_for_empty_top_level_array ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_none_for_non_json ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_none_for_scalar_json ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_none_when_no_suggestion_field ... ok
[INFO] [stdout] test stet::tests::extract_suggestions_stet_shaped ... ok
[INFO] [stdout] test stet::tests::finds_stet_in_custom_path ... ok
[INFO] [stdout] test runner::tests::scheduled_resume_within_parallel_block ... ok
[INFO] [stdout] test stet::tests::finish_session_fails_on_missing_binary ... ok
[INFO] [stdout] test runner::tests::run_all_skips_previously_completed_tasks ... ok
[INFO] [stdout] test runner::tests::scheduled_single_parallel_demoted ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_empty_array ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_includes_path_when_present ... ok
[INFO] [stdout] test stet::tests::finish_session_uses_repo_as_cwd ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_issues_array_same_shape ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_none_for_malformed ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_none_for_non_json ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_object_with_findings ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_stet_shaped_full_finding ... ok
[INFO] [stdout] test stet::tests::parse_findings_from_run_json_top_level_array ... ok
[INFO] [stdout] test stet::tests::parse_triage_fix_finding_abc_dismiss_rest ... ok
[INFO] [stdout] test stet::tests::parse_triage_nothing_to_address_dismiss_all ... ok
[INFO] [stdout] test stet::tests::parse_triage_nothing_to_address_variants ... ok
[INFO] [stdout] test stet::tests::parse_triage_unparseable_dismiss_none ... ok
[INFO] [stdout] test stet::tests::returns_none_for_invalid_explicit_path ... ok
[INFO] [stdout] test stet::tests::returns_none_for_explicit_non_executable_file ... ok
[INFO] [stdout] test stet::tests::returns_none_when_not_on_path ... ok
[INFO] [stdout] test stet::tests::returns_none_when_path_var_is_empty ... ok
[INFO] [stdout] test stet::tests::returns_none_when_path_var_is_none ... ok
[INFO] [stdout] test stet::tests::returns_some_with_valid_explicit_path ... ok
[INFO] [stdout] test state::tests::state_file_path_appends_filename ... ok
[INFO] [stdout] test runner::tests::max_parallel_eq_1_uses_sequential_fallback ... ok
[INFO] [stdout] test stet::tests::dismiss_finding_invokes_stet_with_args_and_repo_cwd ... ok
[INFO] [stdout] test runner::tests::run_all_persists_state_with_all_task_indices ... ok
[INFO] [stdout] test stet::tests::skips_directory_with_same_name ... ok
[INFO] [stdout] test stet::tests::skips_non_executable_file_on_path ... ok
[INFO] [stdout] test stet::tests::run_review_spawn_failure_returns_stet_run_failed ... ok
[INFO] [stdout] test stet::tests::start_session_argv_without_ref ... ok
[INFO] [stdout] test stet::tests::start_session_fails_on_missing_binary ... ok
[INFO] [stdout] test stet::tests::finish_session_argv ... ok
[INFO] [stdout] test stet::tests::start_session_fails_on_nonzero_exit ... ok
[INFO] [stdout] test stet::tests::triage_by_patterns_empty_patterns_nothing_dismissed ... ok
[INFO] [stdout] test stet::tests::triage_by_patterns_match_message_one_dismissed ... ok
[INFO] [stdout] test subprocess::tests::captures_nonzero_exit_code ... ok
[INFO] [stdout] test subprocess::tests::captures_stderr ... ok
[INFO] [stdout] test stet::tests::finish_session_fails_on_nonzero_exit ... ok
[INFO] [stdout] test subprocess::tests::captures_stdout_from_echo ... ok
[INFO] [stdout] test stet::tests::address_loop_resolves_in_one_round ... ok
[INFO] [stdout] test subprocess::tests::run_command_string_empty_returns_none ... ok
[INFO] [stdout] test subprocess::tests::run_command_string_parses_and_runs_echo_hello ... ok
[INFO] [stdout] test stet::tests::run_review_nonzero_exit_is_not_error ... ok
[INFO] [stdout] test subprocess::tests::run_command_string_single_token ... ok
[INFO] [stdout] test stet::tests::run_review_populates_result_with_output ... ok
[INFO] [stdout] test subprocess::tests::success_helper_reports_correctly ... ok
[INFO] [stdout] test subprocess::tests::spawn_failure_returns_io_error ... ok
[INFO] [stdout] test runner::tests::scheduled_all_sequential_runs_in_order ... ok
[INFO] [stdout] test runner::tests::parallel_block_respects_max_parallel ... ok
[INFO] [stdout] test stet::tests::run_review_uses_repo_as_cwd ... ok
[INFO] [stdout] test stet::tests::dismiss_non_actionable_and_rerun_rule_pattern_dismisses_then_rerun ... ok
[INFO] [stdout] test stet::tests::start_session_argv_with_ref ... ok
[INFO] [stdout] test stet::tests::start_session_uses_repo_as_cwd ... ok
[INFO] [stdout] test subprocess::tests::no_timeout_allows_fast_completion ... ok
[INFO] [stdout] test subprocess::tests::respects_cwd ... ok
[INFO] [stdout] test stet::tests::dismiss_non_actionable_and_rerun_llm_nothing_to_address_resolves ... ok
[INFO] [stdout] test stet::tests::address_loop_exhausts_rounds_then_fails ... ok
[INFO] [stdout] test stet::tests::address_loop_exhausts_rounds_then_warns ... ok
[INFO] [stdout] test subprocess::tests::timeout_kills_long_running_process ... ok
[INFO] [stdout] test runner::tests::scheduled_mixed_plan_runs_all_tasks ... ok
[INFO] [stdout] test stet::tests::run_review_timeout_returns_stet_run_failed has been running for over 60 seconds
[INFO] [stdout] test stet::tests::run_review_timeout_returns_stet_run_failed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 356 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 60.57s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/peal-47aae00fbbe3afb2)
[INFO] [stdout] 
[INFO] [stdout] running 18 tests
[INFO] [stdout] test tests::prompt_writes_template_to_file ... ok
[INFO] [stdout] test tests::run_fails_without_plan_or_repo ... ok
[INFO] [stdout] test tests::run_fails_when_plan_file_missing ... ok
[INFO] [stdout] test tests::run_fails_when_repo_not_directory ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.289384Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.289484Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.289677Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.289770Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.289773Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.289813Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.289882Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.289946Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.290147Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpiOtFvc/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpiOtFvc [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.290772Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpxVLX3O/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpxVLX3O [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.290896Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpxQ0uYF/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpxQ0uYF [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.291018Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpuNEcJd/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpuNEcJd [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stdout] test tests::run_fails_when_agent_cmd_not_found ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.293821Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.293851Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.293863Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpo1KDyv/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpo1KDyv [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.294563Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.294596Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.294610Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpycoOEK/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpycoOEK [3magent_cmd[0m[2m=[0m/tmp/.tmpycoOEK/stub_echo_garbage.sh [3magent_path[0m[2m=[0m/tmp/.tmpycoOEK/stub_echo_garbage.sh [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.294618Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.294652Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.294678Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.294737Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.294664Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpcC6LsB/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpcC6LsB [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.294753Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpgAKjTW/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpgAKjTW [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.294965Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.295000Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.295012Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpCchlgK/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpCchlgK [3magent_cmd[0m[2m=[0m/tmp/.tmpCchlgK/stub_agent.sh [3magent_path[0m[2m=[0m/tmp/.tmpCchlgK/stub_agent.sh [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.295063Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.295095Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.295107Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpPJgxkR/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpPJgxkR [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.295648Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.295681Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.295693Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpRNhE29/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpRNhE29 [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.297113Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.297186Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.297203Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpjg5y2L/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpjg5y2L [3magent_cmd[0m[2m=[0m/tmp/.tmpjg5y2L/stub_retry.sh [3magent_path[0m[2m=[0m/tmp/.tmpjg5y2L/stub_retry.sh [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.299508Z[0m [33m WARN[0m configured stet_path is not a valid executable; phase 3 will be skipped [3mpath[0m[2m=[0m/nonexistent
[INFO] [stderr] [2m2026-05-04T11:55:54.299597Z[0m [32m INFO[0m stet not found, phase 3 will be skipped
[INFO] [stderr] [2m2026-05-04T11:55:54.299641Z[0m [32m INFO[0m config loaded [3mplan[0m[2m=[0m/tmp/.tmpY8souW/plan.md [3mrepo[0m[2m=[0m/tmp/.tmpY8souW [3magent_cmd[0m[2m=[0mecho [3magent_path[0m[2m=[0m/usr/bin/echo [3mmodel[0m[2m=[0m"auto" [3mparallel[0m[2m=[0mfalse [3mtimeout_sec[0m[2m=[0m1800
[INFO] [stderr] [2m2026-05-04T11:55:54.303067Z[0m [32m INFO[0m plan format not canonical, normalizing via agent
[INFO] [stderr] [2m2026-05-04T11:55:54.303154Z[0m [32m INFO[0m plan format not canonical, normalizing via agent
[INFO] [stderr] [2m2026-05-04T11:55:54.303713Z[0m [32m INFO[0m plan format not canonical, normalizing via agent
[INFO] [stderr] [2m2026-05-04T11:55:54.306441Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.306453Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.307200Z[0m [32m INFO[0m filtering plan to single task [3mtask_index[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.307375Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.306663Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.307385Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.307258Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.306588Z[0m [32m INFO[0m filtering plan from task onward [3mfrom_task[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.308291Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m2 [3msegment_count[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.308310Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.308286Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.308359Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m2 [3msegment_count[0m[2m=[0m2 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.308443Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.308613Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.308703Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.308905Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m2 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.308945Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stdout] test tests::run_no_summary_on_failure ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.308754Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stdout] test tests::run_fails_when_plan_has_no_tasks ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.309211Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.309378Z[0m [32m INFO[0m phase 1: task 1/2 [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.310563Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m2 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.309573Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.309305Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.309567Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.310425Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m1 [3mplan_text_len[0m[2m=[0m136
[INFO] [stderr] [2m2026-05-04T11:55:54.311114Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.311355Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.310177Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.311387Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.309431Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.311504Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.311543Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.311570Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.311563Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.310764Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.311318Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.311730Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m2 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.315360Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m3 [3mplan_text_len[0m[2m=[0m142
[INFO] [stderr] [2m2026-05-04T11:55:54.315413Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m3 [3mplan_text_len[0m[2m=[0m142
[INFO] [stderr] [2m2026-05-04T11:55:54.315777Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.315821Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m6 [3mplan_text_len[0m[2m=[0m142
[INFO] [stderr] [2m2026-05-04T11:55:54.316095Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.316376Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.316467Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m4 [3mstdout_len[0m[2m=[0m284
[INFO] [stderr] [2m2026-05-04T11:55:54.316795Z[0m [32m INFO[0m all tasks complete [3mcompleted[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.316840Z[0m [32m INFO[0m task complete [3mtask_index[0m[2m=[0m2 [3mplan_text_len[0m[2m=[0m136 [3mphase2_stdout_len[0m[2m=[0m284
[INFO] [stderr] [2m2026-05-04T11:55:54.317124Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m6 [3mplan_text_len[0m[2m=[0m142
[INFO] [stderr] [2m2026-05-04T11:55:54.317298Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.317323Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.315998Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m2 [3mduration_ms[0m[2m=[0m5 [3mplan_text_len[0m[2m=[0m136
[INFO] [stderr] [2m2026-05-04T11:55:54.316200Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m6 [3mplan_text_len[0m[2m=[0m142
[INFO] [stderr] [2m2026-05-04T11:55:54.318288Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.315906Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.318394Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.318216Z[0m [32m INFO[0m phase 2: task 1/2 [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.318471Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m2 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.318331Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stdout] test tests::run_normalize_parse_failure_returns_error_with_snippet ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.316287Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stdout] test tests::run_with_task_flag_runs_single_task ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.354928Z[0m [33m WARN[0m normalized output did not parse, retrying normalization [3mattempt[0m[2m=[0m1 [3mretries_left[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.355177Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.355615Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.356543Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.356588Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/tmp/.tmpCchlgK/stub_agent.sh [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.362448Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m51 [3mplan_text_len[0m[2m=[0m142
[INFO] [stderr] [2m2026-05-04T11:55:54.362501Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.362538Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.367633Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m51 [3mstdout_len[0m[2m=[0m290
[INFO] [stderr] [2m2026-05-04T11:55:54.367724Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stderr] [2m2026-05-04T11:55:54.368444Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m51 [3mstdout_len[0m[2m=[0m290
[INFO] [stderr] [2m2026-05-04T11:55:54.369170Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stderr] [2m2026-05-04T11:55:54.369233Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m2 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m2 [3mduration_ms[0m[2m=[0m50 [3mstdout_len[0m[2m=[0m284
[INFO] [stderr] [2m2026-05-04T11:55:54.369298Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m50 [3mstdout_len[0m[2m=[0m290
[INFO] [stderr] [2m2026-05-04T11:55:54.369353Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stderr] [2m2026-05-04T11:55:54.369657Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m53 [3mstdout_len[0m[2m=[0m290
[INFO] [stderr] [2m2026-05-04T11:55:54.369718Z[0m [32m INFO[0m phase 1: task 2/2 [3mtask_index[0m[2m=[0m3 [3mposition[0m[2m=[0m2 [3mtask_count[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.369771Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m3 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.369926Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m51 [3mstdout_len[0m[2m=[0m290
[INFO] [stderr] [2m2026-05-04T11:55:54.369965Z[0m [32m INFO[0m all tasks complete [3mcompleted[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.369989Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stderr] [2m2026-05-04T11:55:54.369998Z[0m [32m INFO[0m task complete [3mtask_index[0m[2m=[0m1 [3mplan_text_len[0m[2m=[0m142 [3mphase2_stdout_len[0m[2m=[0m290
[INFO] [stdout] test tests::run_succeeds_with_valid_inputs ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.406180Z[0m [32m INFO[0m plan parsed [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.406275Z[0m [32m INFO[0m starting scheduled run [3mtask_count[0m[2m=[0m1 [3msegment_count[0m[2m=[0m1 [3mphase3_available[0m[2m=[0mfalse
[INFO] [stderr] [2m2026-05-04T11:55:54.406302Z[0m [32m INFO[0m phase 1: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.406333Z[0m [32m INFO[0m invoking phase 1 [3mphase[0m[2m=[0m1 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/tmp/.tmpjg5y2L/stub_retry.sh [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.407670Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m51 [3mplan_text_len[0m[2m=[0m17
[INFO] [stderr] [2m2026-05-04T11:55:54.407709Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.407732Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/tmp/.tmpCchlgK/stub_agent.sh [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.413354Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m50 [3mstdout_len[0m[2m=[0m290
[INFO] [stderr] [2m2026-05-04T11:55:54.413451Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stderr] [2m2026-05-04T11:55:54.421416Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m3 [3mposition[0m[2m=[0m2 [3mtask_count[0m[2m=[0m2 [3mduration_ms[0m[2m=[0m51 [3mplan_text_len[0m[2m=[0m135
[INFO] [stderr] [2m2026-05-04T11:55:54.421469Z[0m [32m INFO[0m phase 2: task 2/2 [3mtask_index[0m[2m=[0m3 [3mposition[0m[2m=[0m2 [3mtask_count[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.421501Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m3 [3magent[0m[2m=[0m/usr/bin/echo [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.456883Z[0m [32m INFO[0m phase 1 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m50 [3mplan_text_len[0m[2m=[0m17
[INFO] [stderr] [2m2026-05-04T11:55:54.457888Z[0m [32m INFO[0m phase 2: task 1/1 [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.458003Z[0m [32m INFO[0m invoking phase 2 [3mphase[0m[2m=[0m2 [3mtask_index[0m[2m=[0m1 [3magent[0m[2m=[0m/tmp/.tmpjg5y2L/stub_retry.sh [3mtimeout_sec[0m[2m=[0m1800 [3mattempt[0m[2m=[0m1 [3mmax_attempts[0m[2m=[0m1
[INFO] [stderr] [2m2026-05-04T11:55:54.458266Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m50 [3mstdout_len[0m[2m=[0m17
[INFO] [stderr] [2m2026-05-04T11:55:54.458461Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stderr] [2m2026-05-04T11:55:54.472962Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m3 [3mposition[0m[2m=[0m2 [3mtask_count[0m[2m=[0m2 [3mduration_ms[0m[2m=[0m51 [3mstdout_len[0m[2m=[0m283
[INFO] [stderr] [2m2026-05-04T11:55:54.473566Z[0m [32m INFO[0m all tasks complete [3mcompleted[0m[2m=[0m2 [3mtask_count[0m[2m=[0m2
[INFO] [stderr] [2m2026-05-04T11:55:54.473686Z[0m [32m INFO[0m task complete [3mtask_index[0m[2m=[0m2 [3mplan_text_len[0m[2m=[0m136 [3mphase2_stdout_len[0m[2m=[0m284
[INFO] [stderr] [2m2026-05-04T11:55:54.473714Z[0m [32m INFO[0m task complete [3mtask_index[0m[2m=[0m3 [3mplan_text_len[0m[2m=[0m135 [3mphase2_stdout_len[0m[2m=[0m283
[INFO] [stdout] test tests::run_with_from_task_flag_runs_tail ... ok
[INFO] [stderr] [2m2026-05-04T11:55:54.509538Z[0m [32m INFO[0m phase 2 complete [3mtask_index[0m[2m=[0m1 [3mposition[0m[2m=[0m1 [3mtask_count[0m[2m=[0m1 [3mduration_ms[0m[2m=[0m51 [3mstdout_len[0m[2m=[0m17
[INFO] [stderr] [2m2026-05-04T11:55:54.509651Z[0m [31mERROR[0m failed to save state after task failure [3merr[0m[2m=[0mFailed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] test tests::run_normalize_retry_succeeds_on_second_attempt ... FAILED
[INFO] [stdout] test tests::run_non_canonical_with_normalize_invokes_agent_then_parses ... FAILED
[INFO] [stdout] test tests::run_summary_path_override ... FAILED
[INFO] [stdout] test tests::run_with_post_run_commands_echo_succeeds ... FAILED
[INFO] [stdout] test tests::run_with_failing_post_run_command_still_exits_success ... FAILED
[INFO] [stdout] test tests::run_succeeds_with_config_file ... FAILED
[INFO] [stdout] test tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_normalize_retry_succeeds_on_second_attempt stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_normalize_retry_succeeds_on_second_attempt' (1024) panicked at src/main.rs:849:18:
[INFO] [stdout] retry should succeed on second normalization: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_normalize_retry_succeeds_on_second_attempt
[INFO] [stdout]              at ./src/main.rs:849:9
[INFO] [stdout]    4: peal::tests::run_normalize_retry_succeeds_on_second_attempt::{closure#0}
[INFO] [stdout]              at ./src/main.rs:804:56
[INFO] [stdout]    5: <peal::tests::run_normalize_retry_succeeds_on_second_attempt::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6cd7ff - peal[c70f090bfab38499]::tests::run_normalize_retry_succeeds_on_second_attempt
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:849:18
[INFO] [stdout]   22:     0x65028b6c3147 - peal[c70f090bfab38499]::tests::run_normalize_retry_succeeds_on_second_attempt::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:804:56
[INFO] [stdout]   23:     0x65028b6ecf26 - <peal[c70f090bfab38499]::tests::run_normalize_retry_succeeds_on_second_attempt::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_non_canonical_with_normalize_invokes_agent_then_parses stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_non_canonical_with_normalize_invokes_agent_then_parses' (1022) panicked at src/main.rs:750:18:
[INFO] [stdout] non-canonical + --normalize should invoke stub, then parse and run: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_non_canonical_with_normalize_invokes_agent_then_parses
[INFO] [stdout]              at ./src/main.rs:750:9
[INFO] [stdout]    4: peal::tests::run_non_canonical_with_normalize_invokes_agent_then_parses::{closure#0}
[INFO] [stdout]              at ./src/main.rs:723:68
[INFO] [stdout]    5: <peal::tests::run_non_canonical_with_normalize_invokes_agent_then_parses::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6d01ab - peal[c70f090bfab38499]::tests::run_non_canonical_with_normalize_invokes_agent_then_parses
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:750:18
[INFO] [stdout]   22:     0x65028b6c31d7 - peal[c70f090bfab38499]::tests::run_non_canonical_with_normalize_invokes_agent_then_parses::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:723:68
[INFO] [stdout]   23:     0x65028b6ecfe6 - <peal[c70f090bfab38499]::tests::run_non_canonical_with_normalize_invokes_agent_then_parses::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_summary_path_override stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_summary_path_override' (1027) panicked at src/main.rs:687:18:
[INFO] [stdout] run should succeed: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_summary_path_override
[INFO] [stdout]              at ./src/main.rs:687:9
[INFO] [stdout]    4: peal::tests::run_summary_path_override::{closure#0}
[INFO] [stdout]              at ./src/main.rs:664:35
[INFO] [stdout]    5: <peal::tests::run_summary_path_override::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6c5171 - peal[c70f090bfab38499]::tests::run_summary_path_override
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:687:18
[INFO] [stdout]   22:     0x65028b6c2f07 - peal[c70f090bfab38499]::tests::run_summary_path_override::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:664:35
[INFO] [stdout]   23:     0x65028b6ecc26 - <peal[c70f090bfab38499]::tests::run_summary_path_override::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_with_post_run_commands_echo_succeeds stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_with_post_run_commands_echo_succeeds' (1042) panicked at src/main.rs:1064:18:
[INFO] [stdout] run with post_run_commands echo hello should succeed: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_with_post_run_commands_echo_succeeds
[INFO] [stdout]              at ./src/main.rs:1064:9
[INFO] [stdout]    4: peal::tests::run_with_post_run_commands_echo_succeeds::{closure#0}
[INFO] [stdout]              at ./src/main.rs:1037:50
[INFO] [stdout]    5: <peal::tests::run_with_post_run_commands_echo_succeeds::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stderr] error: test failed, to rerun pass `--bin peal`
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6ccacd - peal[c70f090bfab38499]::tests::run_with_post_run_commands_echo_succeeds
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:1064:18
[INFO] [stdout]   22:     0x65028b6c3117 - peal[c70f090bfab38499]::tests::run_with_post_run_commands_echo_succeeds::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:1037:50
[INFO] [stdout]   23:     0x65028b6ecee6 - <peal[c70f090bfab38499]::tests::run_with_post_run_commands_echo_succeeds::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_with_failing_post_run_command_still_exits_success stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_with_failing_post_run_command_still_exits_success' (1029) panicked at src/main.rs:1033:16:
[INFO] [stdout] post-run failure is best-effort; peal should still exit success: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_with_failing_post_run_command_still_exits_success
[INFO] [stdout]              at ./src/main.rs:1032:22
[INFO] [stdout]    4: peal::tests::run_with_failing_post_run_command_still_exits_success::{closure#0}
[INFO] [stdout]              at ./src/main.rs:1005:63
[INFO] [stdout]    5: <peal::tests::run_with_failing_post_run_command_still_exits_success::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6ce51d - peal[c70f090bfab38499]::tests::run_with_failing_post_run_command_still_exits_success
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:1033:16
[INFO] [stdout]   22:     0x65028b6c3177 - peal[c70f090bfab38499]::tests::run_with_failing_post_run_command_still_exits_success::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:1005:63
[INFO] [stdout]   23:     0x65028b6ecf66 - <peal[c70f090bfab38499]::tests::run_with_failing_post_run_command_still_exits_success::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_succeeds_with_config_file stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_succeeds_with_config_file' (1025) panicked at src/main.rs:881:18:
[INFO] [stdout] should succeed when plan and repo come from config file: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_succeeds_with_config_file
[INFO] [stdout]              at ./src/main.rs:881:9
[INFO] [stdout]    4: peal::tests::run_succeeds_with_config_file::{closure#0}
[INFO] [stdout]              at ./src/main.rs:861:39
[INFO] [stdout]    5: <peal::tests::run_succeeds_with_config_file::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6c630d - peal[c70f090bfab38499]::tests::run_succeeds_with_config_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:881:18
[INFO] [stdout]   22:     0x65028b6c2f37 - peal[c70f090bfab38499]::tests::run_succeeds_with_config_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:861:39
[INFO] [stdout]   23:     0x65028b6ecc66 - <peal[c70f090bfab38499]::tests::run_succeeds_with_config_file::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization' (1015) panicked at src/main.rs:717:18:
[INFO] [stdout] canonical plan with --normalize should parse directly and succeed: Failed to write state file .peal: failed to create directory: Read-only file system (os error 30)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<peal::error::PealError>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<peal::CommandOutcome, anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, peal::error::PealError>>>::from_residual
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: peal::run
[INFO] [stdout]              at ./src/main.rs:380:27
[INFO] [stdout]    3: peal::tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization
[INFO] [stdout]              at ./src/main.rs:717:9
[INFO] [stdout]    4: peal::tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization::{closure#0}
[INFO] [stdout]              at ./src/main.rs:696:83
[INFO] [stdout]    5: <peal::tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x65028be3c60a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x65028be3c60a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x65028be3c60a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x65028be5414a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x65028be5414a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x65028be417e2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x65028be417e2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x65028be1840f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x65028be1840f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x65028be33829 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x65028b703acc - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x65028b703acc - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x65028be33aa2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x65028be33aa2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x65028be184c8 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x65028be0cee9 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x65028be1941d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x65028be5498c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x65028be54702 - core[d17f6591d688f3bd]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x65028b6c35bc - <core[d17f6591d688f3bd]::result::Result<peal[c70f090bfab38499]::CommandOutcome, anyhow[4e6de2a62d64d5f3]::Error>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x65028b6d09bf - peal[c70f090bfab38499]::tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:717:18
[INFO] [stdout]   22:     0x65028b6c3207 - peal[c70f090bfab38499]::tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:696:83
[INFO] [stdout]   23:     0x65028b6ed026 - <peal[c70f090bfab38499]::tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x65028b6f6dab - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x65028b6f6dab - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x65028b70459b - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x65028b70459b - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x65028b70459b - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x65028b70459b - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x65028b6fdcb4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x65028b6fdcb4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x65028b7071a2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x65028b7071a2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x65028b7071a2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x65028b7071a2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x65028b7071a2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x65028be3b9bf - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x65028be3b9bf - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x780169903aa4 - <unknown>
[INFO] [stdout]   45:     0x780169990a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::run_canonical_plan_with_normalize_flag_parses_without_agent_normalization
[INFO] [stdout]     tests::run_non_canonical_with_normalize_invokes_agent_then_parses
[INFO] [stdout]     tests::run_normalize_retry_succeeds_on_second_attempt
[INFO] [stdout]     tests::run_succeeds_with_config_file
[INFO] [stdout]     tests::run_summary_path_override
[INFO] [stdout]     tests::run_with_failing_post_run_command_still_exits_success
[INFO] [stdout]     tests::run_with_post_run_commands_echo_succeeds
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 11 passed; 7 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "0b0018bb60059c4a5152a4185b71137b755d748f900850082719dd6487ef8137", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0b0018bb60059c4a5152a4185b71137b755d748f900850082719dd6487ef8137", kill_on_drop: false }`
[INFO] [stdout] 0b0018bb60059c4a5152a4185b71137b755d748f900850082719dd6487ef8137
